[INFO] cloning repository https://github.com/blueberrycongee/forge
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/blueberrycongee/forge" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fblueberrycongee%2Fforge", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fblueberrycongee%2Fforge'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] ad07eb7d885d399fce7f7da4d53bd8a7714cc907
[INFO] testing blueberrycongee/forge against try#b8e88e5ddf5521a9f43ee3f62a702388c713e4bb for pr-155114
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fblueberrycongee%2Fforge" "/workspace/builds/worker-5-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-5-tc2/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/blueberrycongee/forge
[INFO] finished tweaking git repo https://github.com/blueberrycongee/forge
[INFO] tweaked toml for git repo https://github.com/blueberrycongee/forge written to /workspace/builds/worker-5-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/blueberrycongee/forge on toolchain b8e88e5ddf5521a9f43ee3f62a702388c713e4bb
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+b8e88e5ddf5521a9f43ee3f62a702388c713e4bb" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/blueberrycongee/forge already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+b8e88e5ddf5521a9f43ee3f62a702388c713e4bb" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded rustls-pki-types v1.14.0
[INFO] [stderr]   Downloaded webpki-roots v0.26.11
[INFO] [stderr]   Downloaded zmij v1.0.16
[INFO] [stderr]   Downloaded find-msvc-tools v0.1.8
[INFO] [stderr]   Downloaded iana-time-zone v0.1.64
[INFO] [stderr]   Downloaded uuid v1.19.0
[INFO] [stderr]   Downloaded memchr v2.7.6
[INFO] [stderr]   Downloaded rustls-webpki v0.103.9
[INFO] [stderr]   Downloaded cc v1.2.53
[INFO] [stderr]   Downloaded ureq v2.12.1
[INFO] [stderr]   Downloaded webpki-roots v1.0.6
[INFO] [stderr]   Downloaded chrono v0.4.43
[INFO] [stderr]   Downloaded rustls v0.23.36
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+b8e88e5ddf5521a9f43ee3f62a702388c713e4bb" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] ee56022c06ff1603144e9abc8338f98671b16de54515dec9c615ff482d8f1762
[INFO] running `Command { std: "docker" "start" "-a" "ee56022c06ff1603144e9abc8338f98671b16de54515dec9c615ff482d8f1762", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "ee56022c06ff1603144e9abc8338f98671b16de54515dec9c615ff482d8f1762", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ee56022c06ff1603144e9abc8338f98671b16de54515dec9c615ff482d8f1762", kill_on_drop: false }`
[INFO] [stdout] ee56022c06ff1603144e9abc8338f98671b16de54515dec9c615ff482d8f1762
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+b8e88e5ddf5521a9f43ee3f62a702388c713e4bb" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] a5191c29cd0151188930b0e60c05d8b2e3b98a8b9482d462e8696fb185999403
[INFO] running `Command { std: "docker" "start" "-a" "a5191c29cd0151188930b0e60c05d8b2e3b98a8b9482d462e8696fb185999403", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.105
[INFO] [stderr]    Compiling quote v1.0.43
[INFO] [stderr]    Compiling unicode-ident v1.0.22
[INFO] [stderr]    Compiling stable_deref_trait v1.2.1
[INFO] [stderr]    Compiling libc v0.2.180
[INFO] [stderr]    Compiling writeable v0.6.2
[INFO] [stderr]    Compiling find-msvc-tools v0.1.8
[INFO] [stderr]    Compiling litemap v0.8.1
[INFO] [stderr]    Compiling icu_normalizer_data v2.1.1
[INFO] [stderr]    Compiling icu_properties_data v2.1.2
[INFO] [stderr]    Compiling zeroize v1.8.2
[INFO] [stderr]    Compiling smallvec v1.15.1
[INFO] [stderr]    Compiling autocfg v1.5.0
[INFO] [stderr]    Compiling crc32fast v1.5.0
[INFO] [stderr]    Compiling untrusted v0.9.0
[INFO] [stderr]    Compiling zmij v1.0.16
[INFO] [stderr]    Compiling rustls v0.23.36
[INFO] [stderr]    Compiling percent-encoding v2.3.2
[INFO] [stderr]    Compiling cc v1.2.53
[INFO] [stderr]    Compiling rustls-pki-types v1.14.0
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling simd-adler32 v0.3.8
[INFO] [stderr]    Compiling adler2 v2.0.1
[INFO] [stderr]    Compiling form_urlencoded v1.2.2
[INFO] [stderr]    Compiling memchr v2.7.6
[INFO] [stderr]    Compiling log v0.4.29
[INFO] [stderr]    Compiling subtle v2.6.1
[INFO] [stderr]    Compiling iana-time-zone v0.1.64
[INFO] [stderr]    Compiling miniz_oxide v0.8.9
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling webpki-roots v1.0.6
[INFO] [stderr]    Compiling webpki-roots v0.26.11
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling flate2 v1.1.9
[INFO] [stderr]    Compiling syn v2.0.114
[INFO] [stderr]    Compiling getrandom v0.2.17
[INFO] [stderr]    Compiling ring v0.17.14
[INFO] [stderr]    Compiling chrono v0.4.43
[INFO] [stderr]    Compiling uuid v1.19.0
[INFO] [stderr]    Compiling synstructure v0.13.2
[INFO] [stderr]    Compiling zerovec-derive v0.11.2
[INFO] [stderr]    Compiling displaydoc v0.2.5
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling zerofrom-derive v0.1.6
[INFO] [stderr]    Compiling yoke-derive v0.8.1
[INFO] [stderr]    Compiling zerofrom v0.1.6
[INFO] [stderr]    Compiling yoke v0.8.1
[INFO] [stderr]    Compiling zerotrie v0.2.3
[INFO] [stderr]    Compiling zerovec v0.11.5
[INFO] [stderr]    Compiling tinystr v0.8.2
[INFO] [stderr]    Compiling potential_utf v0.1.4
[INFO] [stderr]    Compiling icu_collections v2.1.1
[INFO] [stderr]    Compiling icu_locale_core v2.1.1
[INFO] [stderr]    Compiling rustls-webpki v0.103.9
[INFO] [stderr]    Compiling icu_provider v2.1.1
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling icu_properties v2.1.2
[INFO] [stderr]    Compiling icu_normalizer v2.1.1
[INFO] [stderr]    Compiling idna_adapter v1.2.1
[INFO] [stderr]    Compiling idna v1.1.0
[INFO] [stderr]    Compiling url v2.5.8
[INFO] [stderr]    Compiling ureq v2.12.1
[INFO] [stderr]    Compiling forge v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 10.99s
[INFO] running `Command { std: "docker" "inspect" "a5191c29cd0151188930b0e60c05d8b2e3b98a8b9482d462e8696fb185999403", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a5191c29cd0151188930b0e60c05d8b2e3b98a8b9482d462e8696fb185999403", kill_on_drop: false }`
[INFO] [stdout] a5191c29cd0151188930b0e60c05d8b2e3b98a8b9482d462e8696fb185999403
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+b8e88e5ddf5521a9f43ee3f62a702388c713e4bb" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 984588cda3466a50668a6c360069eccc8b56e2dfd0a9cb64412df73aeddb6499
[INFO] running `Command { std: "docker" "start" "-a" "984588cda3466a50668a6c360069eccc8b56e2dfd0a9cb64412df73aeddb6499", kill_on_drop: false }`
[INFO] [stderr]    Compiling memchr v2.7.6
[INFO] [stderr]    Compiling syn v2.0.114
[INFO] [stderr]    Compiling slab v0.4.11
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling synstructure v0.13.2
[INFO] [stderr]    Compiling zerofrom-derive v0.1.6
[INFO] [stderr]    Compiling yoke-derive v0.8.1
[INFO] [stderr]    Compiling zerovec-derive v0.11.2
[INFO] [stderr]    Compiling displaydoc v0.2.5
[INFO] [stderr]    Compiling futures-macro v0.3.31
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling futures-util v0.3.31
[INFO] [stderr]    Compiling zerofrom v0.1.6
[INFO] [stderr]    Compiling yoke v0.8.1
[INFO] [stderr]    Compiling zerovec v0.11.5
[INFO] [stderr]    Compiling zerotrie v0.2.3
[INFO] [stderr]    Compiling tinystr v0.8.2
[INFO] [stderr]    Compiling potential_utf v0.1.4
[INFO] [stderr]    Compiling icu_collections v2.1.1
[INFO] [stderr]    Compiling icu_locale_core v2.1.1
[INFO] [stderr]    Compiling icu_provider v2.1.1
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling icu_properties v2.1.2
[INFO] [stderr]    Compiling icu_normalizer v2.1.1
[INFO] [stderr]    Compiling futures-executor v0.3.31
[INFO] [stderr]    Compiling futures v0.3.31
[INFO] [stderr]    Compiling idna_adapter v1.2.1
[INFO] [stderr]    Compiling idna v1.1.0
[INFO] [stderr]    Compiling url v2.5.8
[INFO] [stderr]    Compiling ureq v2.12.1
[INFO] [stderr]    Compiling forge v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 14.04s
[INFO] running `Command { std: "docker" "inspect" "984588cda3466a50668a6c360069eccc8b56e2dfd0a9cb64412df73aeddb6499", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "984588cda3466a50668a6c360069eccc8b56e2dfd0a9cb64412df73aeddb6499", kill_on_drop: false }`
[INFO] [stdout] 984588cda3466a50668a6c360069eccc8b56e2dfd0a9cb64412df73aeddb6499
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+b8e88e5ddf5521a9f43ee3f62a702388c713e4bb" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 79b043b88c110835a4d5dda088f802fdf208551967fe45d38d0a2182b5a4b7cd
[INFO] running `Command { std: "docker" "start" "-a" "79b043b88c110835a4d5dda088f802fdf208551967fe45d38d0a2182b5a4b7cd", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.07s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/forge-6a04e99afefb80a7)
[INFO] [stdout] 
[INFO] [stdout] running 176 tests
[INFO] [stdout] test runtime::ablation::tests::test_config_comparison ... ok
[INFO] [stdout] test runtime::compaction::tests::compaction_context_holds_prompt_hint ... ok
[INFO] [stdout] test runtime::ablation::tests::test_ablation_config ... ok
[INFO] [stdout] test runtime::ablation::tests::test_ablation_study_builder ... ok
[INFO] [stdout] test runtime::compaction::tests::compaction_hook_defaults ... ok
[INFO] [stdout] test runtime::compaction::tests::compaction_policy_token_ratio_threshold ... ok
[INFO] [stdout] test runtime::compaction::tests::compaction_policy_threshold ... ok
[INFO] [stdout] test runtime::compaction::tests::compaction_result_holds_summary ... ok
[INFO] [stdout] test runtime::component::tests::hash_embedding_model_returns_stable_dimensions ... ok
[INFO] [stdout] test runtime::component::tests::in_memory_retriever_ranks_by_overlap ... ok
[INFO] [stdout] test runtime::component::tests::mock_chat_model_generates_response_text ... ok
[INFO] [stdout] test runtime::component::tests::component_interfaces_work_in_graph_workflow ... ok
[INFO] [stdout] test runtime::component::tests::mock_chat_model_stream_emits_text_final ... ok
[INFO] [stdout] test runtime::component::tests::retriever_can_be_registered_as_tool ... ok
[INFO] [stdout] test runtime::evaluator::tests::test_composite_evaluator ... ok
[INFO] [stdout] test runtime::evaluator::tests::test_contains_evaluator ... ok
[INFO] [stdout] test runtime::evaluator::tests::test_latency_evaluator ... ok
[INFO] [stdout] test runtime::event::tests::event_record_cmp_orders_by_seq_then_timestamp_then_id ... ok
[INFO] [stdout] test runtime::event::tests::event_sequencer_assigns_metadata ... ok
[INFO] [stdout] test runtime::event::tests::event_sequencer_starts_after_provided_base ... ok
[INFO] [stdout] test runtime::event::tests::max_record_seq_returns_max_seq ... ok
[INFO] [stdout] test runtime::event::tests::max_record_seq_returns_none_for_empty ... ok
[INFO] [stdout] test runtime::event::tests::event_record_holds_meta_and_payload ... ok
[INFO] [stdout] test runtime::event::tests::tool_status_event_can_be_emitted ... ok
[INFO] [stdout] test runtime::executor::tests::prune_ordering_executes_before_compaction ... ok
[INFO] [stdout] test runtime::event::tests::tool_update_event_can_be_emitted ... ok
[INFO] [stdout] test runtime::executor::tests::execution_config_with_snapshot_messages_seeds_snapshot ... ok
[INFO] [stdout] test runtime::executor::tests::test_ablation_config ... ok
[INFO] [stdout] test runtime::executor::tests::execution_config_with_snapshot_messages_skips_empty_entries ... ok
[INFO] [stdout] test runtime::event::tests::sort_records_by_meta_orders_records ... ok
[INFO] [stdout] test runtime::message::tests::message_new_initializes_empty_parts_and_metadata ... ok
[INFO] [stdout] test runtime::executor::tests::test_execution_config ... ok
[INFO] [stdout] test runtime::executor::tests::stream_events_emits_event_records_with_metadata ... ok
[INFO] [stdout] test runtime::executor::tests::stream_events_records_trace ... ok
[INFO] [stdout] test runtime::message::tests::message_role_from_str_accepts_known_roles_case_insensitively ... ok
[INFO] [stdout] test runtime::executor::tests::stream_events_emits_compaction_event ... ok
[INFO] [stdout] test runtime::executor::tests::stream_events_propagates_sink_write_errors ... ok
[INFO] [stdout] test runtime::message::tests::message_role_from_str_rejects_unknown_roles ... ok
[INFO] [stdout] test runtime::executor::tests::stream_events_emits_from_stream_node ... ok
[INFO] [stdout] test runtime::message::tests::part_from_event_maps_attachment ... ok
[INFO] [stdout] test runtime::message::tests::part_from_event_maps_text_delta ... ok
[INFO] [stdout] test runtime::executor::tests::stream_events_updates_session_snapshot ... ok
[INFO] [stdout] test runtime::executor::tests::stream_events_prunes_event_history ... ok
[INFO] [stdout] test runtime::message::tests::part_from_event_ignores_unrelated_events ... ok
[INFO] [stdout] test runtime::metrics::tests::test_node_metrics ... ok
[INFO] [stdout] test runtime::metrics::tests::test_metrics_collector ... ok
[INFO] [stdout] test runtime::message::tests::part_from_event_maps_error ... ok
[INFO] [stdout] test runtime::message::tests::part_from_event_maps_text_final ... ok
[INFO] [stdout] test runtime::message::tests::part_from_event_maps_token_usage ... ok
[INFO] [stdout] test runtime::message::tests::part_from_event_maps_tool_attachment ... ok
[INFO] [stdout] test runtime::message::tests::part_from_event_maps_tool_result ... ok
[INFO] [stdout] test runtime::output::tests::json_line_event_record_sink_writes_meta ... ok
[INFO] [stdout] test runtime::output::tests::json_line_event_sink_returns_error_on_write_failure ... ok
[INFO] [stdout] test runtime::output::tests::json_line_event_sink_writes_lines ... ok
[INFO] [stdout] test runtime::message::tests::part_from_event_maps_tool_error ... ok
[INFO] [stdout] test runtime::output::tests::sse_event_record_sink_writes_data_frames ... ok
[INFO] [stdout] test runtime::metrics::tests::test_run_metrics ... ok
[INFO] [stdout] test runtime::permission::tests::permission_policy_prefix_wildcard_respects_boundary ... ok
[INFO] [stdout] test runtime::output::tests::sse_event_sink_writes_data_frames ... ok
[INFO] [stdout] test runtime::permission::tests::permission_policy_defaults_to_allow ... ok
[INFO] [stdout] test runtime::permission::tests::permission_request_roundtrip ... ok
[INFO] [stdout] test runtime::permission::tests::permission_session_allows_pattern_overrides ... ok
[INFO] [stdout] test runtime::permission::tests::permission_policy_matches_prefix_wildcards ... ok
[INFO] [stdout] test runtime::permission::tests::permission_policy_uses_first_match ... ok
[INFO] [stdout] test runtime::permission::tests::permission_request_defaults_missing_fields ... ok
[INFO] [stdout] test runtime::permission::tests::permission_session_always_allows ... ok
[INFO] [stdout] test runtime::permission::tests::permission_session_applies_resume_command ... ok
[INFO] [stdout] test runtime::permission::tests::permission_session_ignores_invalid_resume_command ... ok
[INFO] [stdout] test runtime::permission::tests::permission_session_once_consumes_override ... ok
[INFO] [stdout] test runtime::permission::tests::permission_session_once_prefers_most_specific_pattern_first ... ok
[INFO] [stdout] test runtime::permission::tests::permission_session_once_pattern_consumes_after_first_match ... ok
[INFO] [stdout] test runtime::permission::tests::permission_snapshot_roundtrip ... ok
[INFO] [stdout] test runtime::permission::tests::permission_session_snapshot_restore ... ok
[INFO] [stdout] test runtime::permission::tests::permission_session_override_precedence_reject_over_always_and_once ... ok
[INFO] [stdout] test runtime::permission::tests::permission_session_reject_denies ... ok
[INFO] [stdout] test runtime::permission::tests::permission_store_roundtrip ... ok
[INFO] [stdout] test runtime::platform::tests::platform_streams_sse_events ... ok
[INFO] [stdout] test runtime::platform::tests::platform_streams_cli_jsonl_events ... ok
[INFO] [stdout] test runtime::provider::openai::tests::openai_generate_smoke_test ... ignored, requires OPENAI_API_KEY and external network
[INFO] [stdout] test runtime::platform::tests::platform_streams_sse_records ... ok
[INFO] [stdout] test runtime::provider::openai::tests::extract_message_content_supports_array_shape ... ok
[INFO] [stdout] test runtime::platform::tests::platform_streams_cli_jsonl_records ... ok
[INFO] [stdout] test runtime::provider::openai::tests::openai_model_uses_configured_api_key ... ok
[INFO] [stdout] test runtime::provider::openai::tests::parse_chat_response_supports_string_content ... ok
[INFO] [stdout] test runtime::provider::openai::tests::request_payload_maps_messages_and_generation_options ... ok
[INFO] [stdout] test runtime::prune::tests::prune_disabled_keeps_all ... ok
[INFO] [stdout] test runtime::r#loop::tests::loop_context_allows_after_permission_reply ... ok
[INFO] [stdout] test runtime::r#loop::tests::loop_context_asks_permission_for_tool ... ok
[INFO] [stdout] test runtime::r#loop::tests::loop_node_emits_phase_change_events ... ok
[INFO] [stdout] test runtime::provider::openai::tests::parse_error_message_extracts_openai_error_text ... ok
[INFO] [stdout] test runtime::prune::tests::prune_keeps_recent_tool_events ... ok
[INFO] [stdout] test runtime::r#loop::tests::loop_node_finalizes_session_state_message_after_run ... ok
[INFO] [stdout] test runtime::r#loop::tests::loop_context_resumes_permission_from_command ... ok
[INFO] [stdout] test runtime::session::tests::attachment_resolver_rejects_invalid_references ... ok
[INFO] [stdout] test runtime::r#loop::tests::loop_node_emits_phase_rejection_events ... ok
[INFO] [stdout] test runtime::session::tests::session_message_from_message_collects_text_parts_in_order ... ok
[INFO] [stdout] test runtime::r#loop::tests::loop_node_finalize_noop_when_no_pending_parts ... ok
[INFO] [stdout] test runtime::r#loop::tests::loop_node_runs_tools_via_registry ... ok
[INFO] [stdout] test runtime::session::tests::checkpoint_record_defaults_version_when_missing ... ok
[INFO] [stdout] test runtime::r#loop::tests::loop_node_emits_events ... ok
[INFO] [stdout] test runtime::session::tests::session_snapshot_io_helpers_roundtrip ... ok
[INFO] [stdout] test runtime::session::tests::session_message_from_message_ignores_non_text_parts ... ok
[INFO] [stdout] test runtime::session::tests::session_snapshot_push_message_skips_empty_content ... ok
[INFO] [stdout] test runtime::session::tests::session_message_to_message_builds_text_final_parts ... ok
[INFO] [stdout] test runtime::session::tests::session_message_to_message_skips_empty_content ... ok
[INFO] [stdout] test runtime::r#loop::tests::loop_node_updates_session_state_from_events ... ok
[INFO] [stdout] test runtime::session::tests::session_snapshot_push_message_appends_converted_entry ... ok
[INFO] [stdout] test runtime::session::tests::session_snapshot_roundtrip ... ok
[INFO] [stdout] test runtime::session::tests::session_snapshot_to_messages_filters_unknown_roles ... ok
[INFO] [stdout] test runtime::session_state::tests::run_metadata_updates_status_and_error ... ok
[INFO] [stdout] test runtime::session_state::tests::session_state_apply_event_advances_phase_for_permission_asked ... ok
[INFO] [stdout] test runtime::session_state::tests::session_state_apply_event_advances_phase_for_step_finish ... ok
[INFO] [stdout] test runtime::session_state::tests::session_state_apply_event_advances_phase_for_text_delta ... ok
[INFO] [stdout] test runtime::session_state::tests::session_state_apply_event_advances_phase_for_tool_result ... ok
[INFO] [stdout] test runtime::session_state::tests::session_state_apply_event_appends_attachment ... ok
[INFO] [stdout] test runtime::session_state::tests::session_state_apply_event_appends_error ... ok
[INFO] [stdout] test runtime::session_state::tests::session_state_apply_event_advances_phase_for_tool_start ... ok
[INFO] [stdout] test runtime::session_state::tests::session_state_apply_event_appends_token_usage ... ok
[INFO] [stdout] test runtime::session_state::tests::session_state_apply_event_appends_tool_attachment ... ok
[INFO] [stdout] test runtime::session_state::tests::session_state_apply_event_routes_on_permission_replied ... ok
[INFO] [stdout] test runtime::session_state::tests::session_state_apply_event_advances_phase_for_permission_replied ... ok
[INFO] [stdout] test runtime::session_state::tests::session_state_apply_event_tracks_tool_lifecycle ... ok
[INFO] [stdout] test runtime::session::tests::attachment_resolver_loads_reference_payload ... ok
[INFO] [stdout] test runtime::session_state::tests::session_state_apply_event_appends_text_delta ... ok
[INFO] [stdout] test runtime::session::tests::session_snapshot_store_roundtrip ... ok
[INFO] [stdout] test runtime::session_state::tests::session_state_apply_event_appends_text_final ... ok
[INFO] [stdout] test runtime::session::tests::session_store_load_messages_restores_text_parts ... ok
[INFO] [stdout] test runtime::session_state::tests::session_state_apply_event_routes_on_permission_asked ... ok
[INFO] [stdout] test runtime::session_state::tests::session_state_apply_event_with_events_emits_phase_change ... ok
[INFO] [stdout] test runtime::session_state::tests::session_state_apply_event_with_events_emits_tool_phase_steps ... ok
[INFO] [stdout] test runtime::session::tests::checkpoint_store_roundtrip_and_latest ... ok
[INFO] [stdout] test runtime::session_state::tests::session_state_apply_event_with_events_emits_invalid_transition ... ok
[INFO] [stdout] test runtime::session_state::tests::session_state_finalize_message_skips_when_no_pending_parts ... ok
[INFO] [stdout] test runtime::session_state::tests::session_state_apply_event_with_events_emits_permission_interrupt ... ok
[INFO] [stdout] test runtime::session_state::tests::session_state_apply_event_with_events_emits_permission_resumed ... ok
[INFO] [stdout] test runtime::session_state::tests::session_state_finalize_message_merges_pending_parts_in_order ... ok
[INFO] [stdout] test runtime::session_state::tests::session_state_new_initializes_fields ... ok
[INFO] [stdout] test runtime::session_state::tests::session_state_phase_transitions ... ok
[INFO] [stdout] test runtime::session_state::tests::session_state_tracks_tool_calls ... ok
[INFO] [stdout] test runtime::session_state::tests::session_state_routes_between_states ... ok
[INFO] [stdout] test runtime::session_state::tests::session_state_transition_same_phase_emits_none ... ok
[INFO] [stdout] test runtime::session_state::tests::session_state_try_transition_allows_happy_path ... ok
[INFO] [stdout] test runtime::session_state::tests::session_state_try_transition_rejects_invalid ... ok
[INFO] [stdout] test runtime::session_state::tests::session_state_phase_interrupt_and_resume ... ok
[INFO] [stdout] test runtime::session_state::tests::session_state_transition_emits_event ... ok
[INFO] [stdout] test runtime::tool::tests::tool_output_metadata_helpers ... ok
[INFO] [stdout] test runtime::tool::tests::tool_output_with_metadata_accepts_struct ... ok
[INFO] [stdout] test runtime::tool::tests::tool_state_equality ... ok
[INFO] [stdout] test runtime::state::tests::shared_state_merges_with_last_writer ... ok
[INFO] [stdout] test runtime::toolkit::truncation::tests::truncate_text_limits_by_bytes ... ok
[INFO] [stdout] test runtime::toolkit::truncation::tests::truncate_text_limits_by_lines ... ok
[INFO] [stdout] test runtime::tool::tests::tool_registry_returns_error_for_missing_tool ... ok
[INFO] [stdout] test runtime::tool::tests::tool_runner_emits_status_and_result ... ok
[INFO] [stdout] test runtime::tool::tests::tool_schema_registry_annotates_missing_metadata ... ok
[INFO] [stdout] test runtime::tool::tests::tool_registry_dispatches_by_name ... ok
[INFO] [stdout] test runtime::tool::tests::tool_schema_registry_does_not_override_existing_metadata ... ok
[INFO] [stdout] test runtime::state::tests::loop_state_tracks_session_and_routing ... ok
[INFO] [stdout] test runtime::toolkit::truncation::tests::truncate_text_keeps_tail_when_requested ... ok
[INFO] [stdout] test runtime::trace::tests::trace_records_events_and_spans ... ok
[INFO] [stdout] test runtime::trace::tests::trace_replay_emits_event_records_with_metadata ... ok
[INFO] [stdout] test runtime::trace::tests::trace_replay_emits_events ... ok
[INFO] [stdout] test runtime::tool::tests::tool_schema_registry_has_common_entries ... ok
[INFO] [stdout] test runtime::toolkit::truncation::tests::truncate_text_returns_full_content_without_limits ... ok
[INFO] [stdout] test runtime::trace::tests::trace_replay_records_with_existing_offset ... ok
[INFO] [stdout] test runtime::trace::tests::trace_replay_read_audit_log_records_sorts_by_seq ... ok
[INFO] [stdout] test runtime::trace::tests::trace_replay_returns_events_in_order ... ok
[INFO] [stdout] test runtime::trace::tests::trace_replay_read_audit_log_records_round_trip ... ok
[INFO] [stdout] test runtime::trace::tests::trace_replay_records_with_start_seq_offset ... ok
[INFO] [stdout] test runtime::trace::tests::trace_roundtrip ... ok
[INFO] [stdout] test runtime::trace::tests::trace_replay_to_json_emits_array ... ok
[INFO] [stdout] test runtime::trace::tests::trace_replay_write_audit_log_records ... ok
[INFO] [stdout] test runtime::trace::tests::trace_replay_records_with_record_log_file ... ok
[INFO] [stdout] test runtime::trace::tests::trace_replay_read_audit_log_records_rejects_unknown_version ... ok
[INFO] [stdout] test runtime::trace::tests::trace_replay_to_record_json_emits_array_with_metadata ... ok
[INFO] [stdout] test runtime::trace::tests::trace_replay_write_audit_log ... ok
[INFO] [stdout] test runtime::trace::tests::trace_replay_read_audit_log_records_supports_legacy_array ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 175 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/contract_suite.rs (/opt/rustwide/target/debug/deps/contract_suite-94ad89f5d0fddc81)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test tool_context_contract::contract_includes_permission_abort_and_attachment_endpoints ... ok
[INFO] [stdout] test serialization_contract::event_record_fixture_roundtrip_is_stable ... ok
[INFO] [stdout] test serialization_contract::checkpoint_record_fixture_roundtrip_is_stable ... ok
[INFO] [stdout] test serialization_contract::session_snapshot_fixture_roundtrip_is_stable ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/integration_suite.rs (/opt/rustwide/target/debug/deps/integration_suite-adea73584c3eeaa7)
[INFO] [stdout] 
[INFO] [stdout] running 19 tests
[INFO] [stdout] test graph_routing::conditional_routing_selects_reject ... ok
[INFO] [stdout] test agent_handoff::agent_handoff_merges_shared_state ... ok
[INFO] [stdout] test graph_routing::conditional_routing_selects_approve ... ok
[INFO] [stdout] test pause_resume::pause_resume_allows_reusing_same_checkpoint_deterministically ... ok
[INFO] [stdout] test pause_resume::pause_and_resume_from_checkpoint ... ok
[INFO] [stdout] test pause_resume::pause_resume_handles_multiple_interrupt_nodes ... ok
[INFO] [stdout] test pause_resume::pause_resume_reinterrupts_on_invalid_resume_value ... ok
[INFO] [stdout] test pause_resume::pause_resume_rejects_mismatched_interrupt_id_for_single_pending_interrupt ... ok
[INFO] [stdout] test pause_resume::pause_resume_requires_interrupt_map_for_multiple_pending_interrupts ... ok
[INFO] [stdout] test permission_flow::permission_flow_denies_tool ... ok
[INFO] [stdout] test permission_flow::permission_flow_interrupts_on_ask ... ok
[INFO] [stdout] test permission_flow::permission_flow_records_reply_and_allows ... ok
[INFO] [stdout] test tool_context_abort::aborting_tool_run_marks_aborted ... ok
[INFO] [stdout] test tool_context_attachments::tool_emits_attachment_events ... ok
[INFO] [stdout] test pause_resume::pause_resume_can_continue_from_latest_persisted_checkpoint ... ok
[INFO] [stdout] test pause_resume::pause_resume_can_continue_from_persisted_checkpoint_after_restart ... ok
[INFO] [stdout] test tool_context_permission::tool_context_permission_interrupts_and_resumes ... ok
[INFO] [stdout] test tool_context_permission::tool_context_permission_without_reply_stays_interrupted ... ok
[INFO] [stdout] test workflow_run::workflow_runs_with_file_tools ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 19 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/unit_suite.rs (/opt/rustwide/target/debug/deps/unit_suite-0ecee9a45c84695e)
[INFO] [stdout] 
[INFO] [stdout] running 13 tests
[INFO] [stdout] test attachment_policy::attachment_policy_converts_large_inline_payloads ... ok
[INFO] [stdout] test attachment_policy::attachment_policy_rejects_empty_mime_type ... ok
[INFO] [stdout] test tool_context_abort::tool_context_abort_returns_aborted_error ... ok
[INFO] [stdout] test tool_context::tool_context_asks_permission_and_interrupts ... ok
[INFO] [stdout] test tool_context_abort::tool_context_check_cancelled_returns_aborted_error ... ok
[INFO] [stdout] test tool_context_edge_cases::attachment_inline_at_threshold_keeps_inline_payload ... ok
[INFO] [stdout] test tool_context_edge_cases::attachment_reference_payload_passes_through_without_store ... ok
[INFO] [stdout] test tool_context_edge_cases::attachment_store_missing_for_oversize_inline_payload_errors ... ok
[INFO] [stdout] test tool_interface::tool_context_emits_tool_update ... ok
[INFO] [stdout] test tool_interface::tool_registry_exposes_definitions ... ok
[INFO] [stdout] test tool_interface::tool_runner_reports_attachment_before_result ... ok
[INFO] [stdout] test tool_interface::tool_runner_reports_error_events ... ok
[INFO] [stdout] test tool_interface::tool_runner_reports_success_events ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 13 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests forge
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test src/runtime/state.rs - runtime::state::GraphState (line 15) - compile ... ok
[INFO] [stdout] test src/runtime/error.rs - runtime::error::interrupt (line 187) - compile ... ok
[INFO] [stdout] test src/runtime/mod.rs - runtime (line 50) - compile ... ok
[INFO] [stdout] test src/runtime/graph.rs - runtime::graph::StateGraph (line 29) - compile ... ok
[INFO] [stdout] test src/runtime/mod.rs - runtime (line 15) - compile ... ok
[INFO] [stdout] test src/runtime/graph.rs - runtime::graph::StateGraph<S>::add_node (line 100) - compile ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.06s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "79b043b88c110835a4d5dda088f802fdf208551967fe45d38d0a2182b5a4b7cd", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "79b043b88c110835a4d5dda088f802fdf208551967fe45d38d0a2182b5a4b7cd", kill_on_drop: false }`
[INFO] [stdout] 79b043b88c110835a4d5dda088f802fdf208551967fe45d38d0a2182b5a4b7cd
